[INFO] cloning repository https://github.com/Bazooh/RustLox
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Bazooh/RustLox" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] f0dfc0cf917f5997c94a6cb5556b8b9c354da810
[INFO] testing Bazooh/RustLox against try#28f95933dc0727396c07b48d0a8cb5211b7fb472 for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox" "/workspace/builds/worker-6-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/Bazooh/RustLox
[INFO] finished tweaking git repo https://github.com/Bazooh/RustLox
[INFO] tweaked toml for git repo https://github.com/Bazooh/RustLox written to /workspace/builds/worker-6-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/Bazooh/RustLox on toolchain 28f95933dc0727396c07b48d0a8cb5211b7fb472
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/Bazooh/RustLox already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] d9941d9a32ec57d9afde144704b77a49bb123edd5d74763cf513731a7762e74d
[INFO] running `Command { std: "docker" "start" "-a" "d9941d9a32ec57d9afde144704b77a49bb123edd5d74763cf513731a7762e74d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d9941d9a32ec57d9afde144704b77a49bb123edd5d74763cf513731a7762e74d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d9941d9a32ec57d9afde144704b77a49bb123edd5d74763cf513731a7762e74d", kill_on_drop: false }`
[INFO] [stdout] d9941d9a32ec57d9afde144704b77a49bb123edd5d74763cf513731a7762e74d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 789347e28e37747e9a0341418ed4cecae000d9fc9bf0d256820398922ecf8682
[INFO] running `Command { std: "docker" "start" "-a" "789347e28e37747e9a0341418ed4cecae000d9fc9bf0d256820398922ecf8682", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.104
[INFO] [stderr]    Compiling semver v1.0.27
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling relative-path v1.9.3
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling futures-timer v3.0.3
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling rstest_macros v0.26.1
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling quote v1.0.42
[INFO] [stderr]    Compiling syn v2.0.112
[INFO] [stderr]    Compiling toml_parser v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling toml_edit v0.23.10+spec-1.0.0
[INFO] [stderr]    Compiling proc-macro-crate v3.4.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling rstest v0.26.1
[INFO] [stderr]    Compiling RustLox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: method `repr` is never used
[INFO] [stdout]   --> src/instruction.rs:96:12
[INFO] [stdout]    |
[INFO] [stdout] 95 | impl Instruction {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] 96 |     pub fn repr(&self) -> String {
[INFO] [stdout]    |            ^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `parse_expect` is never used
[INFO] [stdout]  --> src/test.rs:6:4
[INFO] [stdout]   |
[INFO] [stdout] 6 | fn parse_expect(content: &str) -> (String, String) {
[INFO] [stdout]   |    ^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_file` is never used
[INFO] [stdout]   --> src/test.rs:35:4
[INFO] [stdout]    |
[INFO] [stdout] 35 | fn test_file(path: &str, func: fn(&str) -> (String, String)) {
[INFO] [stdout]    |    ^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_interpret_file` is never used
[INFO] [stdout]   --> src/test.rs:91:4
[INFO] [stdout]    |
[INFO] [stdout] 91 | fn test_interpret_file(path: &str) {
[INFO] [stdout]    |    ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_tokenize_file` is never used
[INFO] [stdout]    --> src/test.rs:108:4
[INFO] [stdout]     |
[INFO] [stdout] 108 | fn test_tokenize_file(path: &str) {
[INFO] [stdout]     |    ^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 15.82s
[INFO] running `Command { std: "docker" "inspect" "789347e28e37747e9a0341418ed4cecae000d9fc9bf0d256820398922ecf8682", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "789347e28e37747e9a0341418ed4cecae000d9fc9bf0d256820398922ecf8682", kill_on_drop: false }`
[INFO] [stdout] 789347e28e37747e9a0341418ed4cecae000d9fc9bf0d256820398922ecf8682
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4ed26b84a69d7ef5dd59d768ef72220adb49437655ac5dc3f8a9aebf02abc9da
[INFO] running `Command { std: "docker" "start" "-a" "4ed26b84a69d7ef5dd59d768ef72220adb49437655ac5dc3f8a9aebf02abc9da", kill_on_drop: false }`
[INFO] [stderr]    Compiling RustLox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: method `repr` is never used
[INFO] [stdout]   --> src/instruction.rs:96:12
[INFO] [stdout]    |
[INFO] [stdout] 95 | impl Instruction {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] 96 |     pub fn repr(&self) -> String {
[INFO] [stdout]    |            ^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.05s
[INFO] running `Command { std: "docker" "inspect" "4ed26b84a69d7ef5dd59d768ef72220adb49437655ac5dc3f8a9aebf02abc9da", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4ed26b84a69d7ef5dd59d768ef72220adb49437655ac5dc3f8a9aebf02abc9da", kill_on_drop: false }`
[INFO] [stdout] 4ed26b84a69d7ef5dd59d768ef72220adb49437655ac5dc3f8a9aebf02abc9da
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e88d89930c04968f0514a6cc6f130e1d1c95941d86752bd6d323b4e3650ab043
[INFO] running `Command { std: "docker" "start" "-a" "e88d89930c04968f0514a6cc6f130e1d1c95941d86752bd6d323b4e3650ab043", kill_on_drop: false }`
[INFO] [stderr] warning: method `repr` is never used
[INFO] [stderr]   --> src/instruction.rs:96:12
[INFO] [stderr]    |
[INFO] [stderr] 95 | impl Instruction {
[INFO] [stderr]    | ---------------- method in this implementation
[INFO] [stderr] 96 |     pub fn repr(&self) -> String {
[INFO] [stderr]    |            ^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `RustLox` (bin "RustLox" test) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/RustLox-5686169b899852d7)
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test test::test_module::case_09_expressions ... ok
[INFO] [stdout] test test::test_module::case_02_block ... ok
[INFO] [stdout] test test::test_empty_file ... ok
[INFO] [stdout] test test::test_module::case_03_bool ... ok
[INFO] [stdout] test test::test_module::case_15_logical_operator ... ok
[INFO] [stdout] test test::test_module::case_20_print ... ok
[INFO] [stdout] test test::test_module::case_07_comments ... ok
[INFO] [stdout] test test::test_module::case_01_assignment ... ok
[INFO] [stdout] test test::test_module::case_04_call ... ok
[INFO] [stdout] test test::test_module::case_17_nil ... ok
[INFO] [stdout] test test::test_module::case_25_this ... FAILED
[INFO] [stdout] test test::test_module::case_05_class ... FAILED
[INFO] [stdout] test test::test_module::case_08_constructor ... FAILED
[INFO] [stdout] test test::test_module::case_14_inheritance ... FAILED
[INFO] [stdout] test test::test_module::case_24_super ... FAILED
[INFO] [stdout] test test::test_module::case_21_regression ... ok
[INFO] [stdout] test test::test_module::case_11_for_ ... ok
[INFO] [stdout] test test::test_precedence ... ok
[INFO] [stdout] test test::test_unexpected_character ... ok
[INFO] [stdout] test test::test_module::case_23_string ... ok
[INFO] [stdout] test test::test_module::case_13_if_ ... ok
[INFO] [stdout] test test::test_module::case_06_closure ... ok
[INFO] [stdout] test test::test_module::case_27_while_ ... ok
[INFO] [stdout] test test::test_module::case_22_return_ ... ok
[INFO] [stdout] test test::test_tokenize::case_1 ... ok
[INFO] [stdout] test test::test_module::case_16_method ... ok
[INFO] [stdout] test test::test_module::case_18_number ... ok
[INFO] [stdout] test test::test_module::case_12_function ... ok
[INFO] [stdout] test test::test_module::case_26_variable ... ok
[INFO] [stdout] test test::test_module::case_10_field ... ok
[INFO] [stdout] test test::test_module::case_19_operator ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_25_this stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 21,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 27,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Captured(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 13,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 3, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 3 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn getClosure>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: GetGlobal { string_index: 3 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<class Foo>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<class Foo>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: DotProperty(0) Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<Foo instance>", "<fn toString>", "<class Foo>", "<fn getClosure>", "<fn toString>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<fn getClosure>"]
[INFO] [stdout] GC: checking value "<fn getClosure>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<fn getClosure>", "<class Foo>", "<Foo instance>", "<fn toString>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: Callable { address: 13, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<fn getClosure>", "<class Foo>"]
[INFO] [stdout] FREEING: ["<Foo instance>", "<fn getClosure>", "<fn toString>"]
[INFO] [stdout] Executing byte at 23: GetLocal(0) Stack: ["<fn closure>"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_25_this' (43) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x60be43423d58 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x60be43423d58 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::trace_unsynchronized::<std[d054f6f42beb1baa]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x60be43423d58 - std[d054f6f42beb1baa]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x60be43423d58 - <<std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[5a1c3e9896636f3a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x60be4343adba - <core[5a1c3e9896636f3a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x60be4343adba - core[5a1c3e9896636f3a]::fmt::write
[INFO] [stdout]    6:     0x60be434287c2 - std[d054f6f42beb1baa]::io::default_write_fmt::<alloc[154f689e5dd3bae5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x60be434287c2 - <alloc[154f689e5dd3bae5]::vec::Vec<u8> as std[d054f6f42beb1baa]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x60be434028c6 - <std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x60be434028c6 - std[d054f6f42beb1baa]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x60be4341bdd9 - std[d054f6f42beb1baa]::panicking::default_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x60be43143dac - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x60be43143dac - test[fad20673b750c10e]::test_main_inner::<test[fad20673b750c10e]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x60be4341bf92 - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x60be4341bf92 - std[d054f6f42beb1baa]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x60be43402982 - std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x60be433fa129 - std[d054f6f42beb1baa]::sys::backtrace::__rust_end_short_backtrace::<std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x60be4340369d - __rustc[7ab284efd7f33ea8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x60be4343b5fc - core[5a1c3e9896636f3a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x60be4343b2f4 - core[5a1c3e9896636f3a]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x60be4343b2f4 - core[5a1c3e9896636f3a]::option::expect_failed
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/option.rs:2261:5
[INFO] [stdout]   21:     0x60be43118850 - <core[5a1c3e9896636f3a]::option::Option<&RustLox[edf3d52f4df47cd9]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/option.rs:968:21
[INFO] [stdout]   22:     0x60be431153de - <RustLox[edf3d52f4df47cd9]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x60be43115e22 - <RustLox[edf3d52f4df47cd9]::heap::Heap>::gc::dfs
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:136:35
[INFO] [stdout]   24:     0x60be43115ee4 - <RustLox[edf3d52f4df47cd9]::heap::Heap>::gc::dfs
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:137:17
[INFO] [stdout]   25:     0x60be431119ee - <RustLox[edf3d52f4df47cd9]::heap::Heap>::gc::<core[5a1c3e9896636f3a]::iter::adapters::chain::Chain<core[5a1c3e9896636f3a]::slice::iter::Iter<RustLox[edf3d52f4df47cd9]::value::Value>, std[d054f6f42beb1baa]::collections::hash::map::Values<alloc[154f689e5dd3bae5]::string::String, RustLox[edf3d52f4df47cd9]::value::Value>>>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:146:17
[INFO] [stdout]   26:     0x60be430fdba8 - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:438:23
[INFO] [stdout]   27:     0x60be4310815a - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   28:     0x60be431264bd - RustLox[edf3d52f4df47cd9]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   29:     0x60be4311016a - <RustLox[edf3d52f4df47cd9]::test::test_interpret_file::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   30:     0x60be4312a2a8 - RustLox[edf3d52f4df47cd9]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   31:     0x60be4312a03a - RustLox[edf3d52f4df47cd9]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   32:     0x60be43128f6d - RustLox[edf3d52f4df47cd9]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   33:     0x60be43115812 - RustLox[edf3d52f4df47cd9]::test::test_module::case_25_this
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   34:     0x60be43113567 - RustLox[edf3d52f4df47cd9]::test::test_module::case_25_this::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   35:     0x60be43110326 - <RustLox[edf3d52f4df47cd9]::test::test_module::case_25_this::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x60be43136e8b - <fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   37:     0x60be43136e8b - test[fad20673b750c10e]::__rust_begin_short_backtrace::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:724:18
[INFO] [stdout]   38:     0x60be43144705 - test[fad20673b750c10e]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:74
[INFO] [stdout]   39:     0x60be43144705 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40:     0x60be43144705 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x60be43144705 - std[d054f6f42beb1baa]::panicking::catch_unwind::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x60be43144705 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x60be43144705 - test[fad20673b750c10e]::run_test_in_process
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:27
[INFO] [stdout]   44:     0x60be43144705 - test[fad20673b750c10e]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:668:43
[INFO] [stdout]   45:     0x60be4313f264 - test[fad20673b750c10e]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:698:41
[INFO] [stdout]   46:     0x60be4313f264 - std[d054f6f42beb1baa]::sys::backtrace::__rust_begin_short_backtrace::<test[fad20673b750c10e]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   47:     0x60be43147782 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   48:     0x60be43147782 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   49:     0x60be43147782 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   50:     0x60be43147782 - std[d054f6f42beb1baa]::panicking::catch_unwind::<(), core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   51:     0x60be43147782 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   52:     0x60be43147782 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   53:     0x60be43147782 - <std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   54:     0x60be4342337f - <alloc[154f689e5dd3bae5]::boxed::Box<dyn core[5a1c3e9896636f3a]::ops::function::FnOnce<(), Output = ()> + core[5a1c3e9896636f3a]::marker::Send> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   55:     0x60be4342337f - <std[d054f6f42beb1baa]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   56:     0x7821718d4aa4 - <unknown>
[INFO] [stdout]   57:     0x782171961a64 - clone
[INFO] [stdout]   58:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_05_class stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Constant(
[INFO] [stdout]         0.0,
[INFO] [stdout]     ),
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 37,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Constant(
[INFO] [stdout]         1.0,
[INFO] [stdout]     ),
[INFO] [stdout]     Add,
[INFO] [stdout]     AssignGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Constant(0.0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["0"]
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class A>", "<fn print_id>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: GetGlobal { string_index: 4 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn print_id>", "<class A>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn print_id>", "<fn init>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<fn print_id>", "<class A>"]
[INFO] [stdout] FREEING: ["<fn init>", "<A instance>", "<fn print_id>"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_05_class' (23) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x60be43423d58 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x60be43423d58 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::trace_unsynchronized::<std[d054f6f42beb1baa]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x60be43423d58 - std[d054f6f42beb1baa]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x60be43423d58 - <<std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[5a1c3e9896636f3a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x60be4343adba - <core[5a1c3e9896636f3a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x60be4343adba - core[5a1c3e9896636f3a]::fmt::write
[INFO] [stdout]    6:     0x60be434287c2 - std[d054f6f42beb1baa]::io::default_write_fmt::<alloc[154f689e5dd3bae5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x60be434287c2 - <alloc[154f689e5dd3bae5]::vec::Vec<u8> as std[d054f6f42beb1baa]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x60be434028c6 - <std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x60be434028c6 - std[d054f6f42beb1baa]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x60be4341bdd9 - std[d054f6f42beb1baa]::panicking::default_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x60be43143dac - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x60be43143dac - test[fad20673b750c10e]::test_main_inner::<test[fad20673b750c10e]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x60be4341bf92 - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x60be4341bf92 - std[d054f6f42beb1baa]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x60be43402982 - std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x60be433fa129 - std[d054f6f42beb1baa]::sys::backtrace::__rust_end_short_backtrace::<std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x60be4340369d - __rustc[7ab284efd7f33ea8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x60be4343b5fc - core[5a1c3e9896636f3a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x60be4343b2f4 - core[5a1c3e9896636f3a]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x60be4343b2f4 - core[5a1c3e9896636f3a]::option::expect_failed
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/option.rs:2261:5
[INFO] [stdout]   21:     0x60be43118850 - <core[5a1c3e9896636f3a]::option::Option<&RustLox[edf3d52f4df47cd9]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/option.rs:968:21
[INFO] [stdout]   22:     0x60be431153de - <RustLox[edf3d52f4df47cd9]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x60be4310d75d - <RustLox[edf3d52f4df47cd9]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x60be430fb8da - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x60be430fde86 - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x60be4310815a - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x60be431264bd - RustLox[edf3d52f4df47cd9]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x60be4311016a - <RustLox[edf3d52f4df47cd9]::test::test_interpret_file::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x60be4312a2a8 - RustLox[edf3d52f4df47cd9]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x60be4312a03a - RustLox[edf3d52f4df47cd9]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x60be43128f6d - RustLox[edf3d52f4df47cd9]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x60be43115892 - RustLox[edf3d52f4df47cd9]::test::test_module::case_05_class
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x60be431135c7 - RustLox[edf3d52f4df47cd9]::test::test_module::case_05_class::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x60be431103a6 - <RustLox[edf3d52f4df47cd9]::test::test_module::case_05_class::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x60be43136e8b - <fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x60be43136e8b - test[fad20673b750c10e]::__rust_begin_short_backtrace::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x60be43144705 - test[fad20673b750c10e]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x60be43144705 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x60be43144705 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x60be43144705 - std[d054f6f42beb1baa]::panicking::catch_unwind::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x60be43144705 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x60be43144705 - test[fad20673b750c10e]::run_test_in_process
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x60be43144705 - test[fad20673b750c10e]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x60be4313f264 - test[fad20673b750c10e]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x60be4313f264 - std[d054f6f42beb1baa]::sys::backtrace::__rust_begin_short_backtrace::<test[fad20673b750c10e]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x60be43147782 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x60be43147782 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x60be43147782 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x60be43147782 - std[d054f6f42beb1baa]::panicking::catch_unwind::<(), core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x60be43147782 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x60be43147782 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x60be43147782 - <std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x60be4342337f - <alloc[154f689e5dd3bae5]::boxed::Box<dyn core[5a1c3e9896636f3a]::ops::function::FnOnce<(), Output = ()> + core[5a1c3e9896636f3a]::marker::Send> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   54:     0x60be4342337f - <std[d054f6f42beb1baa]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x7821718d4aa4 - <unknown>
[INFO] [stdout]   56:     0x782171961a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_08_constructor stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 12,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 7,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 2, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 2 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: GetGlobal { string_index: 2 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: Callable { address: 7, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: ["<Foo instance>", "<fn init>"]
[INFO] [stdout] Executing byte at 14: GetLocal(0) Stack: ["<fn init>"]
[INFO] [stdout] GC: checking value "<fn init>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: Call { arg_count: 0 } Stack: ["<fn init>", "<fn init>"]
[INFO] [stdout] GC: checking value "<fn init>"
[INFO] [stdout] GC: checking value "<fn init>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: String(1) Stack: ["<fn init>", "<fn init>"]
[INFO] [stdout] GC: checking value "<fn init>"
[INFO] [stdout] GC: checking value "<fn init>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Return Stack: ["<fn init>", "<fn init>", "bar"]
[INFO] [stdout] GC: checking value "<fn init>"
[INFO] [stdout] GC: checking value "<fn init>"
[INFO] [stdout] GC: checking value "bar"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Foo>", "<fn init>", "bar"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: Print Stack: ["<fn init>", "bar"]
[INFO] [stdout] GC: checking value "<fn init>"
[INFO] [stdout] GC: checking value "bar"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>", "<fn init>", "bar"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: GetCaptured(0) Stack: ["<fn init>"]
[INFO] [stdout] GC: checking value "<fn init>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>", "<fn init>"]
[INFO] [stdout] FREEING: ["bar"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_08_constructor' (26) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x60be43423d58 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x60be43423d58 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::trace_unsynchronized::<std[d054f6f42beb1baa]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x60be43423d58 - std[d054f6f42beb1baa]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x60be43423d58 - <<std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[5a1c3e9896636f3a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x60be4343adba - <core[5a1c3e9896636f3a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x60be4343adba - core[5a1c3e9896636f3a]::fmt::write
[INFO] [stdout]    6:     0x60be434287c2 - std[d054f6f42beb1baa]::io::default_write_fmt::<alloc[154f689e5dd3bae5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x60be434287c2 - <alloc[154f689e5dd3bae5]::vec::Vec<u8> as std[d054f6f42beb1baa]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x60be434028c6 - <std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x60be434028c6 - std[d054f6f42beb1baa]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x60be4341bdd9 - std[d054f6f42beb1baa]::panicking::default_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x60be43143dac - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x60be43143dac - test[fad20673b750c10e]::test_main_inner::<test[fad20673b750c10e]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x60be4341bf92 - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x60be4341bf92 - std[d054f6f42beb1baa]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x60be43402982 - std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x60be433fa129 - std[d054f6f42beb1baa]::sys::backtrace::__rust_end_short_backtrace::<std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x60be4340369d - __rustc[7ab284efd7f33ea8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x60be4343b5fc - core[5a1c3e9896636f3a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x60be4343b2f4 - core[5a1c3e9896636f3a]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x60be4343b2f4 - core[5a1c3e9896636f3a]::option::expect_failed
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/option.rs:2261:5
[INFO] [stdout]   21:     0x60be43118850 - <core[5a1c3e9896636f3a]::option::Option<&RustLox[edf3d52f4df47cd9]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/option.rs:968:21
[INFO] [stdout]   22:     0x60be431153de - <RustLox[edf3d52f4df47cd9]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x60be4310d75d - <RustLox[edf3d52f4df47cd9]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x60be430fb8da - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x60be430fde86 - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x60be4310815a - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x60be431264bd - RustLox[edf3d52f4df47cd9]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x60be4311016a - <RustLox[edf3d52f4df47cd9]::test::test_interpret_file::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x60be4312a2a8 - RustLox[edf3d52f4df47cd9]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x60be4312a03a - RustLox[edf3d52f4df47cd9]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x60be43128f6d - RustLox[edf3d52f4df47cd9]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x60be43115c92 - RustLox[edf3d52f4df47cd9]::test::test_module::case_08_constructor
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x60be431138c7 - RustLox[edf3d52f4df47cd9]::test::test_module::case_08_constructor::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x60be431107a6 - <RustLox[edf3d52f4df47cd9]::test::test_module::case_08_constructor::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x60be43136e8b - <fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x60be43136e8b - test[fad20673b750c10e]::__rust_begin_short_backtrace::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x60be43144705 - test[fad20673b750c10e]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x60be43144705 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x60be43144705 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x60be43144705 - std[d054f6f42beb1baa]::panicking::catch_unwind::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x60be43144705 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x60be43144705 - test[fad20673b750c10e]::run_test_in_process
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x60be43144705 - test[fad20673b750c10e]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x60be4313f264 - test[fad20673b750c10e]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x60be4313f264 - std[d054f6f42beb1baa]::sys::backtrace::__rust_begin_short_backtrace::<test[fad20673b750c10e]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x60be43147782 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x60be43147782 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x60be43147782 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x60be43147782 - std[d054f6f42beb1baa]::panicking::catch_unwind::<(), core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x60be43147782 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x60be43147782 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x60be43147782 - <std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x60be4342337f - <alloc[154f689e5dd3bae5]::boxed::Box<dyn core[5a1c3e9896636f3a]::ops::function::FnOnce<(), Output = ()> + core[5a1c3e9896636f3a]::marker::Send> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   54:     0x60be4342337f - <std[d054f6f42beb1baa]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x7821718d4aa4 - <unknown>
[INFO] [stdout]   56:     0x782171961a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_14_inheritance stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Nil,
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 2,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 30,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 8,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 5,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 35,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 8,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 8,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         6,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         4,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         7,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 2, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 2 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Class { name_index: 5, superclass: Some(Global(2)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 5 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inFoo>", "<class Foo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Class { name_index: 8, superclass: Some(Global(5)) } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inFoo>", "<fn inBar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Bar>
[INFO] [stdout] Executing byte at 8: CreateGlobal { string_index: 8 } Stack: ["<class Baz>"]
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inFoo>", "<class Foo>", "<class Baz>", "<fn inBaz>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: GetGlobal { string_index: 8 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<fn inFoo>", "<fn inBar>", "<class Foo>", "<class Bar>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: Call { arg_count: 0 } Stack: ["<class Baz>"]
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Bar>", "<fn inBaz>", "<class Baz>", "<fn inFoo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: CreateGlobal { string_index: 9 } Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<class Baz>", "<fn inBar>", "<fn inFoo>", "<class Bar>", "<fn inFoo>", "<fn inBar>", "<fn inBaz>", "<Baz instance>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Baz instance>", "<class Bar>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<class Foo>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: DotProperty(0) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inBaz>", "<fn inBaz>", "<fn inFoo>", "<Baz instance>", "<fn inBar>", "<fn inFoo>", "<class Bar>", "<class Baz>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: Call { arg_count: 0 } Stack: ["<fn inFoo>"]
[INFO] [stdout] GC: checking value "<fn inFoo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<class Bar>", "<fn inBar>", "<fn inBaz>", "<fn inBaz>", "<Baz instance>", "<class Foo>", "<fn inFoo>", "<fn inBar>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: String(1) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<fn inFoo>", "<class Foo>", "<fn inBaz>", "<fn inBaz>", "<Baz instance>", "<fn inBar>", "<fn inFoo>", "<class Bar>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: Print Stack: ["in foo"]
[INFO] [stdout] GC: checking value "in foo"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inBaz>", "in foo", "<fn inFoo>", "<fn inBaz>", "<fn inBar>", "<class Foo>", "<Baz instance>", "<fn inFoo>", "<fn inBar>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inFoo>", "<class Baz>", "<fn inFoo>", "<Baz instance>", "<class Foo>", "<fn inBaz>", "<class Bar>", "<fn inBaz>", "<fn inBar>"]
[INFO] [stdout] FREEING: ["in foo"]
[INFO] [stdout] Executing byte at 29: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<fn inFoo>", "<fn inBar>", "<class Bar>", "<fn inBar>", "<Baz instance>", "<class Baz>", "<fn inBaz>", "<fn inBaz>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<Baz instance>", "<fn inBaz>", "<fn inBar>", "<fn inBaz>", "<class Foo>", "<fn inFoo>", "<class Bar>", "<fn inBar>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Baz instance>", "<class Bar>", "<fn inFoo>", "<class Foo>", "<fn inBar>", "<fn inBaz>", "<fn inBaz>", "<class Baz>", "<fn inBar>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: DotProperty(3) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<class Foo>", "<fn inFoo>", "<class Bar>", "<fn inBar>", "<class Baz>", "<fn inBaz>", "<fn inBaz>", "<fn inBar>", "<Baz instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: Call { arg_count: 0 } Stack: ["<fn inBar>"]
[INFO] [stdout] GC: checking value "<fn inBar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inFoo>", "<class Baz>", "<fn inBaz>", "<class Bar>", "<fn inFoo>", "<fn inBar>", "<class Foo>", "<fn inBaz>", "<Baz instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: String(4) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<fn inBar>", "<fn inBaz>", "<class Baz>", "<fn inFoo>", "<Baz instance>", "<fn inBar>", "<class Bar>", "<fn inFoo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: Print Stack: ["in bar"]
[INFO] [stdout] GC: checking value "in bar"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "in bar", "<Baz instance>", "<fn inFoo>", "<fn inBaz>", "<class Foo>", "<fn inBaz>", "<class Bar>", "<fn inBar>", "<fn inFoo>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 33: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inBaz>", "<fn inBaz>", "<fn inFoo>", "<fn inBar>", "<class Bar>", "<class Baz>", "<fn inFoo>", "<Baz instance>", "<fn inBar>"]
[INFO] [stdout] FREEING: ["in bar"]
[INFO] [stdout] Executing byte at 34: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<fn inFoo>", "<class Foo>", "<class Bar>", "<class Baz>", "<Baz instance>", "<fn inBaz>", "<fn inBaz>", "<fn inBar>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<class Baz>", "<class Bar>", "<Baz instance>", "<class Foo>", "<fn inFoo>", "<fn inBaz>", "<fn inBar>", "<fn inBar>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Bar>", "<class Foo>", "<fn inBaz>", "<fn inBaz>", "<class Baz>", "<fn inFoo>", "<fn inBar>", "<fn inFoo>", "<Baz instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: DotProperty(6) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inFoo>", "<fn inBar>", "<class Baz>", "<fn inFoo>", "<fn inBaz>", "<Baz instance>", "<fn inBaz>", "<fn inBar>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: Call { arg_count: 0 } Stack: ["<fn inBaz>"]
[INFO] [stdout] GC: checking value "<fn inBaz>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Baz instance>", "<fn inBar>", "<class Bar>", "<fn inBaz>", "<class Baz>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<class Foo>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 36: String(7) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Baz>", "<fn inFoo>", "<fn inBaz>", "<fn inBar>", "<class Foo>", "<Baz instance>", "<class Bar>", "<fn inBaz>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: Print Stack: ["in baz"]
[INFO] [stdout] GC: checking value "in baz"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Baz>", "<fn inFoo>", "<fn inFoo>", "<fn inBaz>", "<class Bar>", "<class Foo>", "<fn inBaz>", "<Baz instance>", "<fn inBar>", "in baz"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Foo>", "<fn inFoo>", "<fn inBar>", "<fn inBaz>", "<class Bar>", "<class Baz>", "<Baz instance>", "<fn inFoo>", "<fn inBaz>"]
[INFO] [stdout] FREEING: ["in baz"]
[INFO] [stdout] Executing byte at 39: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<fn inBaz>", "<class Foo>", "<class Baz>", "<fn inBar>", "<fn inFoo>", "<class Bar>", "<Baz instance>", "<fn inFoo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<class Foo>", "<fn inFoo>", "<class Baz>", "<fn inBaz>", "<class Bar>", "<Baz instance>", "<fn inBar>", "<fn inFoo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 24: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inFoo>", "<class Bar>", "<fn inBaz>", "<fn inBaz>", "<Baz instance>", "<fn inBar>", "<fn inFoo>", "<fn inBar>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 5,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Callable { address: 5, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<fn foo>"]
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 8,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 0, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Callable { address: 8, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 1 } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Class { name_index: 2, superclass: Some(Global(0)) } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: GetLocal(1) Stack: ["<fn f>", "<class B>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<class B>", "<class A>", "<fn f>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: Return Stack: ["<fn f>", "<class B>", "<class B>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<class B>", "<class A>", "<fn f>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Print Stack: ["<class B>"]
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class B>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn f>"]
[INFO] [stdout] FREEING: ["<class B>"]
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 36,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 47,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 7,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 4,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 56,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 65,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 7,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 7,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         8,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         9,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         10,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         11,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         6,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stderr] error: test failed, to rerun pass `--bin RustLox`
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 4 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 7, superclass: Some(Global(4)) } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn fooPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 7: CreateGlobal { string_index: 7 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<fn foo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: GetGlobal { string_index: 7 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<class Foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Bar>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: CreateGlobal { string_index: 6 } Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Bar>", "<Bar instance>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn foo>", "<fn barPrint>", "<class Foo>", "<fn bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: String(8) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<class Bar>", "<Bar instance>", "<fn barPrint>", "<fn bar>", "<class Foo>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: String(9) Stack: ["foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 1", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<fn bar>", "<class Foo>", "<fn foo>", "<Bar instance>", "<fn barPrint>", "<fn fooPrint>", "<class Bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: GetGlobal { string_index: 6 } Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<fn bar>", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<class Bar>", "<fn barPrint>", "<class Foo>", "foo 1", "<fn bar>", "foo 2", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: DotProperty(0) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "foo 1", "<class Foo>", "<fn fooPrint>", "<class Bar>", "<fn foo>", "<fn bar>", "<fn fooPrint>", "foo 2", "<fn barPrint>", "<fn bar>", "<Bar instance>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: Call { arg_count: 2 } Stack: ["foo 1", "foo 2", "<fn foo>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Bar>", "<fn foo>", "<class Foo>", "<fn foo>", "<fn barPrint>", "foo 2", "foo 1", "<fn barPrint>", "<Bar instance>", "<fn fooPrint>", "<fn bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: GetCaptured(0) Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<Bar instance>", "<class Bar>", "<fn bar>", "<fn barPrint>", "<class Foo>", "<fn barPrint>", "foo 2", "foo 1", "<fn foo>", "<fn foo>", "<fn fooPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: GetLocal(0) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn bar>", "<class Foo>", "<fn barPrint>", "<class Bar>", "foo 1", "<fn foo>", "foo 2", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 39: AssignField { field_name_index: 1 } Stack: ["foo 1", "foo 2", "<Bar instance>", "foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn fooPrint>", "<fn fooPrint>", "foo 1", "<fn barPrint>", "<class Bar>", "<fn barPrint>", "<fn bar>", "foo 2", "<fn foo>", "<class Foo>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: Pop(1) Stack: ["foo 1", "foo 2", "foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn foo>", "<fn fooPrint>", "<Bar instance>", "foo 1", "<fn bar>", "foo 2", "<fn barPrint>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<class Foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: GetCaptured(0) Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Foo>", "<fn bar>", "<fn barPrint>", "foo 2", "<fn foo>", "<fn bar>", "foo 1", "<Bar instance>", "<class Bar>", "<fn foo>", "<fn fooPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: GetLocal(1) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Foo>", "<fn foo>", "foo 1", "<class Bar>", "<fn fooPrint>", "<fn bar>", "foo 2", "<fn barPrint>", "<fn barPrint>", "<fn bar>", "<Bar instance>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 43: AssignField { field_name_index: 2 } Stack: ["foo 1", "foo 2", "<Bar instance>", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<class Bar>", "<fn bar>", "<class Foo>", "<fn foo>", "foo 1", "foo 2", "<Bar instance>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 44: Pop(1) Stack: ["foo 1", "foo 2", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 2", "<fn fooPrint>", "<fn foo>", "<fn foo>", "<fn barPrint>", "<class Foo>", "<Bar instance>", "<fn bar>", "foo 1", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 45: Nil Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn bar>", "<fn barPrint>", "foo 2", "<fn foo>", "<fn foo>", "<fn fooPrint>", "<fn bar>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<class Foo>", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 46: Return Stack: ["foo 1", "foo 2", "nil"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Foo>", "<fn foo>", "<fn barPrint>", "foo 2", "<class Bar>", "<fn bar>", "<fn bar>", "<fn fooPrint>", "<fn foo>", "foo 1", "<fn barPrint>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<fn foo>", "foo 1", "<Bar instance>", "<class Bar>", "<fn fooPrint>", "<fn barPrint>", "<class Foo>", "<fn fooPrint>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: GetGlobal { string_index: 6 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "foo 2", "<class Bar>", "<fn bar>", "<class Foo>", "<Bar instance>", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: DotProperty(3) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn bar>", "<fn bar>", "<fn foo>", "<Bar instance>", "<class Foo>", "foo 1", "<fn fooPrint>", "<fn foo>", "foo 2", "<class Bar>", "<fn barPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: Call { arg_count: 0 } Stack: ["<fn fooPrint>"]
[INFO] [stdout] GC: checking value "<fn fooPrint>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn barPrint>", "<fn fooPrint>", "<class Bar>", "<Bar instance>", "foo 2", "<fn barPrint>", "<fn bar>", "<class Foo>", "<fn bar>", "<fn foo>", "<fn foo>", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 48: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<Bar instance>", "<class Foo>", "<fn fooPrint>", "foo 2", "<class Bar>", "<fn foo>", "<fn bar>", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 49: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "foo 2", "<Bar instance>", "<fn fooPrint>", "foo 1", "<class Bar>", "<fn bar>", "<fn fooPrint>", "<class Foo>", "<fn foo>", "<fn foo>", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 50: Print Stack: ["foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<Bar instance>", "<fn foo>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "<fn fooPrint>", "foo 1", "<class Foo>", "<fn foo>", "<fn bar>", "<fn barPrint>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 51: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<Bar instance>", "foo 2", "<class Foo>", "<fn barPrint>", "<class Bar>", "foo 1", "<fn bar>", "<fn fooPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 52: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "<fn bar>", "foo 1", "<class Foo>", "<fn foo>", "<fn fooPrint>", "foo 2", "<class Bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 53: Print Stack: ["foo 2"]
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 2", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "foo 1", "<fn bar>", "<class Foo>", "<class Bar>", "<Bar instance>", "<fn bar>", "<fn foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 54: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn barPrint>", "<Bar instance>", "<fn fooPrint>", "foo 2", "<class Bar>", "foo 1", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<class Foo>", "<fn foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 55: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn bar>", "<class Foo>", "<fn foo>", "<Bar instance>", "<fn foo>", "<fn fooPrint>", "foo 2", "<fn barPrint>", "<class Bar>", "<fn barPrint>", "<fn bar>", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn bar>", "foo 1", "<fn barPrint>", "<fn foo>", "<class Foo>", "foo 2", "<class Bar>", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: String(10) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "foo 1", "<fn fooPrint>", "<fn foo>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<class Bar>", "<fn barPrint>", "foo 2", "<class Foo>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: String(11) Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Bar>", "<fn bar>", "bar 1", "foo 2", "foo 1", "<fn fooPrint>", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>", "<fn bar>", "<class Foo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: GetGlobal { string_index: 6 } Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "foo 2", "<fn foo>", "bar 2", "<class Bar>", "<fn fooPrint>", "<fn barPrint>", "<class Foo>", "<fn fooPrint>", "<fn bar>", "foo 1", "<Bar instance>", "bar 1", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 24: DotProperty(6) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn barPrint>", "bar 1", "<class Bar>", "foo 1", "<fn bar>", "<fn foo>", "<fn foo>", "foo 2", "bar 2", "<fn fooPrint>", "<class Foo>", "<Bar instance>", "<fn fooPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 25: Call { arg_count: 2 } Stack: ["bar 1", "bar 2", "<fn bar>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<fn bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<Bar instance>", "<fn foo>", "<fn bar>", "bar 1", "<fn fooPrint>", "bar 2", "<class Bar>", "<fn foo>", "foo 1", "<fn fooPrint>", "<fn barPrint>", "<class Foo>", "<fn barPrint>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 66: GetCaptured(0) Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "bar 1", "<class Foo>", "foo 2", "<fn bar>", "<fn bar>", "<fn fooPrint>", "<Bar instance>", "<fn foo>", "<class Bar>", "bar 2", "<fn foo>", "foo 1", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 67: GetLocal(0) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 2", "<fn barPrint>", "foo 1", "bar 2", "bar 1", "<fn fooPrint>", "<class Bar>", "<fn foo>", "<fn fooPrint>", "<fn barPrint>", "<class Foo>", "<Bar instance>", "<fn foo>", "<fn bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 68: AssignField { field_name_index: 1 } Stack: ["bar 1", "bar 2", "<Bar instance>", "bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 2", "foo 1", "<fn foo>", "<fn barPrint>", "<fn fooPrint>", "foo 2", "<fn bar>", "<Bar instance>", "<fn foo>", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<class Bar>", "<class Foo>", "bar 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 69: Pop(1) Stack: ["bar 1", "bar 2", "bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn foo>", "<fn fooPrint>", "foo 2", "<fn bar>", "bar 2", "<class Foo>", "<fn fooPrint>", "<fn barPrint>", "<Bar instance>", "<class Bar>", "<fn bar>", "<fn barPrint>", "bar 1"]
[INFO] [stdout] FREEING: ["foo 1"]
[INFO] [stdout] Executing byte at 70: GetCaptured(0) Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "foo 2", "<fn barPrint>", "bar 2", "<fn foo>", "<fn bar>", "<fn fooPrint>", "bar 1", "<class Bar>", "<class Foo>", "<fn foo>", "<Bar instance>", "<fn fooPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 71: GetLocal(1) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn foo>", "<Bar instance>", "<class Foo>", "foo 2", "<fn fooPrint>", "bar 2", "bar 1", "<class Bar>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 72: AssignField { field_name_index: 2 } Stack: ["bar 1", "bar 2", "<Bar instance>", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<Bar instance>", "bar 1", "<fn bar>", "<fn barPrint>", "<fn foo>", "bar 2", "<fn foo>", "<fn barPrint>", "foo 2", "<fn bar>", "<class Bar>", "<fn fooPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 73: Pop(1) Stack: ["bar 1", "bar 2", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "bar 1", "bar 2", "<fn bar>", "<fn foo>", "<class Foo>", "<Bar instance>", "<fn barPrint>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: ["foo 2"]
[INFO] [stdout] Executing byte at 74: Nil Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 2", "<fn bar>", "<fn foo>", "<fn foo>", "<fn bar>", "bar 1", "<fn fooPrint>", "<class Foo>", "<fn barPrint>", "<fn fooPrint>", "<Bar instance>", "<class Bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 75: Return Stack: ["bar 1", "bar 2", "nil"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 1", "<fn foo>", "<fn barPrint>", "bar 2", "<class Foo>", "<fn bar>", "<fn fooPrint>", "<Bar instance>", "<fn bar>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "bar 2", "<class Foo>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "<class Bar>", "<Bar instance>", "<fn foo>", "bar 1", "<fn foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: GetGlobal { string_index: 6 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn bar>", "<class Foo>", "<fn fooPrint>", "<fn barPrint>", "bar 1", "<fn foo>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<class Bar>", "bar 2", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: DotProperty(5) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<class Bar>", "<class Foo>", "bar 2", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "bar 1", "<Bar instance>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 29: Call { arg_count: 0 } Stack: ["<fn barPrint>"]
[INFO] [stdout] GC: checking value "<fn barPrint>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "bar 1", "<fn fooPrint>", "<class Bar>", "<Bar instance>", "<fn barPrint>", "<fn foo>", "<fn barPrint>", "bar 2", "<fn bar>", "<fn fooPrint>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 57: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<Bar instance>", "<class Foo>", "<fn foo>", "<fn barPrint>", "<fn fooPrint>", "bar 2", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "<class Bar>", "bar 1", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 58: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 2", "<class Bar>", "<Bar instance>", "<fn barPrint>", "<fn barPrint>", "<fn bar>", "<class Foo>", "<fn foo>", "<fn bar>", "<fn fooPrint>", "bar 1", "<fn fooPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 59: Print Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<Bar instance>", "<fn barPrint>", "<class Foo>", "<fn barPrint>", "<fn fooPrint>", "bar 1", "<fn foo>", "bar 2", "<fn bar>", "<class Bar>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 60: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "bar 1", "<fn fooPrint>", "<class Foo>", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "bar 2", "<fn bar>", "<fn fooPrint>", "<Bar instance>", "<class Bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 61: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn fooPrint>", "bar 2", "<fn foo>", "<fn bar>", "<Bar instance>", "<fn barPrint>", "<class Bar>", "<fn foo>", "bar 1", "<fn fooPrint>", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 62: Print Stack: ["bar 2"]
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 2", "<fn fooPrint>", "<fn bar>", "<class Bar>", "<class Foo>", "<Bar instance>", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "<fn bar>", "bar 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 63: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn bar>", "<class Bar>", "<fn foo>", "<Bar instance>", "bar 1", "<fn foo>", "<fn barPrint>", "<fn fooPrint>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 64: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<fn fooPrint>", "<fn fooPrint>", "bar 2", "<fn foo>", "<fn bar>", "<class Foo>", "<fn barPrint>", "<class Bar>", "<Bar instance>", "<fn foo>", "bar 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 30: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn fooPrint>", "bar 1", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "<fn bar>", "bar 2", "<fn bar>", "<Bar instance>", "<class Foo>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: GetGlobal { string_index: 6 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 1", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<class Foo>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<Bar instance>", "bar 2", "<class Bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: DotProperty(3) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<Bar instance>", "<fn barPrint>", "bar 1", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<class Foo>", "<fn foo>", "<fn foo>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 33: Call { arg_count: 0 } Stack: ["<fn fooPrint>"]
[INFO] [stdout] GC: checking value "<fn fooPrint>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn fooPrint>", "bar 2", "<fn barPrint>", "<class Foo>", "<Bar instance>", "<fn barPrint>", "<fn bar>", "bar 1", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 48: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<Bar instance>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "bar 2", "<class Foo>", "bar 1", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 49: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn fooPrint>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<fn barPrint>", "bar 1", "<class Bar>", "<fn bar>", "<class Foo>", "<Bar instance>", "<fn barPrint>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 50: Print Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn barPrint>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "<fn bar>", "bar 1", "bar 2", "<class Foo>", "<fn foo>", "<Bar instance>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 51: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Foo>", "<fn foo>", "<Bar instance>", "bar 1", "<fn bar>", "<fn bar>", "<fn foo>", "bar 2", "<fn barPrint>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 52: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn foo>", "<fn bar>", "<fn fooPrint>", "<fn fooPrint>", "bar 2", "<class Bar>", "<fn barPrint>", "<fn bar>", "bar 1", "<class Foo>", "<fn barPrint>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 53: Print Stack: ["bar 2"]
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "bar 1", "<fn fooPrint>", "<fn fooPrint>", "bar 2", "<class Foo>", "<fn barPrint>", "<class Bar>", "<Bar instance>", "<fn barPrint>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 54: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "<fn foo>", "<fn fooPrint>", "<fn barPrint>", "<Bar instance>", "<fn barPrint>", "<fn fooPrint>", "<class Foo>", "<fn bar>", "bar 1", "bar 2", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 55: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn fooPrint>", "<fn barPrint>", "bar 2", "<fn foo>", "<class Bar>", "<Bar instance>", "<fn bar>", "<fn fooPrint>", "<fn bar>", "bar 1", "<fn foo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 34: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "bar 2", "bar 1", "<fn fooPrint>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<class Foo>", "<fn bar>", "<fn bar>", "<Bar instance>", "<fn foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 35: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "bar 2", "<class Bar>", "<fn barPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<class Foo>", "<fn foo>", "<fn bar>", "<fn fooPrint>", "bar 1", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 15,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 21,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 3,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 3, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 3 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn test>", "<fn init>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 4, superclass: Some(Global(3)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<fn test>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class B>"]
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn init>", "<fn test>", "<class B>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: String(5) Stack: []
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn test>", "<fn init>", "<class B>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: GetGlobal { string_index: 4 } Stack: ["value"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn init>", "<fn test>", "value", "<class B>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Call { arg_count: 1 } Stack: ["value", "<class B>"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn init>", "<class B>", "value", "<fn test>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: GetCaptured(0) Stack: ["value"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class A>", "<fn test>", "<class B>", "value"]
[INFO] [stdout] FREEING: ["<B instance>", "<fn init>", "<fn test>"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_14_inheritance' (32) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x60be43423d58 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x60be43423d58 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::trace_unsynchronized::<std[d054f6f42beb1baa]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x60be43423d58 - std[d054f6f42beb1baa]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x60be43423d58 - <<std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[5a1c3e9896636f3a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x60be4343adba - <core[5a1c3e9896636f3a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x60be4343adba - core[5a1c3e9896636f3a]::fmt::write
[INFO] [stdout]    6:     0x60be434287c2 - std[d054f6f42beb1baa]::io::default_write_fmt::<alloc[154f689e5dd3bae5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x60be434287c2 - <alloc[154f689e5dd3bae5]::vec::Vec<u8> as std[d054f6f42beb1baa]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x60be434028c6 - <std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x60be434028c6 - std[d054f6f42beb1baa]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x60be4341bdd9 - std[d054f6f42beb1baa]::panicking::default_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x60be43143dac - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x60be43143dac - test[fad20673b750c10e]::test_main_inner::<test[fad20673b750c10e]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x60be4341bf92 - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x60be4341bf92 - std[d054f6f42beb1baa]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x60be43402982 - std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x60be433fa129 - std[d054f6f42beb1baa]::sys::backtrace::__rust_end_short_backtrace::<std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x60be4340369d - __rustc[7ab284efd7f33ea8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x60be4343b5fc - core[5a1c3e9896636f3a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x60be4343b2f4 - core[5a1c3e9896636f3a]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x60be4343b2f4 - core[5a1c3e9896636f3a]::option::expect_failed
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/option.rs:2261:5
[INFO] [stdout]   21:     0x60be43118850 - <core[5a1c3e9896636f3a]::option::Option<&RustLox[edf3d52f4df47cd9]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/option.rs:968:21
[INFO] [stdout]   22:     0x60be431153de - <RustLox[edf3d52f4df47cd9]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x60be4310d75d - <RustLox[edf3d52f4df47cd9]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x60be430fb8da - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x60be430fde86 - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x60be4310815a - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x60be431264bd - RustLox[edf3d52f4df47cd9]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x60be4311016a - <RustLox[edf3d52f4df47cd9]::test::test_interpret_file::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x60be4312a2a8 - RustLox[edf3d52f4df47cd9]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x60be4312a03a - RustLox[edf3d52f4df47cd9]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x60be43128f6d - RustLox[edf3d52f4df47cd9]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x60be43115d12 - RustLox[edf3d52f4df47cd9]::test::test_module::case_14_inheritance
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x60be43113927 - RustLox[edf3d52f4df47cd9]::test::test_module::case_14_inheritance::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x60be43110826 - <RustLox[edf3d52f4df47cd9]::test::test_module::case_14_inheritance::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x60be43136e8b - <fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x60be43136e8b - test[fad20673b750c10e]::__rust_begin_short_backtrace::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x60be43144705 - test[fad20673b750c10e]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x60be43144705 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x60be43144705 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x60be43144705 - std[d054f6f42beb1baa]::panicking::catch_unwind::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x60be43144705 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x60be43144705 - test[fad20673b750c10e]::run_test_in_process
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x60be43144705 - test[fad20673b750c10e]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x60be4313f264 - test[fad20673b750c10e]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x60be4313f264 - std[d054f6f42beb1baa]::sys::backtrace::__rust_begin_short_backtrace::<test[fad20673b750c10e]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x60be43147782 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x60be43147782 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x60be43147782 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x60be43147782 - std[d054f6f42beb1baa]::panicking::catch_unwind::<(), core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x60be43147782 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x60be43147782 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x60be43147782 - <std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x60be4342337f - <alloc[154f689e5dd3bae5]::boxed::Box<dyn core[5a1c3e9896636f3a]::ops::function::FnOnce<(), Output = ()> + core[5a1c3e9896636f3a]::marker::Send> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   54:     0x60be4342337f - <std[d054f6f42beb1baa]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x7821718d4aa4 - <unknown>
[INFO] [stdout]   56:     0x782171961a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_24_super stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 12,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 2,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 17,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 2, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 2 } Stack: ["<class Base>"]
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Class { name_index: 4, superclass: Some(Global(2)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Base>
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class Derived>"]
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Base>", "<fn foo>", "<class Derived>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: GetGlobal { string_index: 4 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn foo>", "<class Derived>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<class Derived>"]
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "<fn foo>", "<class Base>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: DotProperty(0) Stack: ["<Derived instance>"]
[INFO] [stdout] GC: checking value "<Derived instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Derived>", "<class Base>", "<fn foo>", "<fn foo>", "<Derived instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<fn foo>"]
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: reachable: ["<Derived instance>", "<fn foo>", "<fn foo>", "<fn foo>", "<class Derived>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: String(3) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn foo>", "<class Derived>", "<class Base>"]
[INFO] [stdout] FREEING: ["<fn foo>", "<Derived instance>"]
[INFO] [stdout] Executing byte at 19: Print Stack: ["Derived.foo()"]
[INFO] [stdout] GC: checking value "Derived.foo()"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn foo>", "Derived.foo()", "<class Derived>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: GetCaptured(1) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "<class Base>", "<fn foo>", "<fn foo>"]
[INFO] [stdout] FREEING: ["Derived.foo()"]
[INFO] [stdout] Executing byte at 21: DotProperty(0) Stack: ["super"]
[INFO] [stdout] GC: checking value "super"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "<fn foo>", "<fn foo>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_24_super' (42) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x60be43423d58 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x60be43423d58 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::trace_unsynchronized::<std[d054f6f42beb1baa]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x60be43423d58 - std[d054f6f42beb1baa]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x60be43423d58 - <<std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[5a1c3e9896636f3a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x60be4343adba - <core[5a1c3e9896636f3a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x60be4343adba - core[5a1c3e9896636f3a]::fmt::write
[INFO] [stdout]    6:     0x60be434287c2 - std[d054f6f42beb1baa]::io::default_write_fmt::<alloc[154f689e5dd3bae5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x60be434287c2 - <alloc[154f689e5dd3bae5]::vec::Vec<u8> as std[d054f6f42beb1baa]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x60be434028c6 - <std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x60be434028c6 - std[d054f6f42beb1baa]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x60be4341bdd9 - std[d054f6f42beb1baa]::panicking::default_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x60be43143dac - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x60be43143dac - test[fad20673b750c10e]::test_main_inner::<test[fad20673b750c10e]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x60be4341bf92 - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x60be4341bf92 - std[d054f6f42beb1baa]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x60be43402982 - std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x60be433fa129 - std[d054f6f42beb1baa]::sys::backtrace::__rust_end_short_backtrace::<std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x60be4340369d - __rustc[7ab284efd7f33ea8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x60be4343b5fc - core[5a1c3e9896636f3a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x60be4343b2f4 - core[5a1c3e9896636f3a]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x60be4343b2f4 - core[5a1c3e9896636f3a]::option::expect_failed
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/option.rs:2261:5
[INFO] [stdout]   21:     0x60be43118850 - <core[5a1c3e9896636f3a]::option::Option<&RustLox[edf3d52f4df47cd9]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/option.rs:968:21
[INFO] [stdout]   22:     0x60be431153de - <RustLox[edf3d52f4df47cd9]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x60be4310d75d - <RustLox[edf3d52f4df47cd9]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x60be430fb8da - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x60be4310052b - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:800:49
[INFO] [stdout]   26:     0x60be4310815a - <RustLox[edf3d52f4df47cd9]::interpreter::Interpreter<&mut alloc[154f689e5dd3bae5]::string::String, &mut alloc[154f689e5dd3bae5]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x60be431264bd - RustLox[edf3d52f4df47cd9]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x60be4311016a - <RustLox[edf3d52f4df47cd9]::test::test_interpret_file::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x60be4312a2a8 - RustLox[edf3d52f4df47cd9]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x60be4312a03a - RustLox[edf3d52f4df47cd9]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x60be43128f6d - RustLox[edf3d52f4df47cd9]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x60be43115952 - RustLox[edf3d52f4df47cd9]::test::test_module::case_24_super
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x60be43113657 - RustLox[edf3d52f4df47cd9]::test::test_module::case_24_super::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x60be43110466 - <RustLox[edf3d52f4df47cd9]::test::test_module::case_24_super::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x60be43136e8b - <fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x60be43136e8b - test[fad20673b750c10e]::__rust_begin_short_backtrace::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x60be43144705 - test[fad20673b750c10e]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x60be43144705 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x60be43144705 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x60be43144705 - std[d054f6f42beb1baa]::panicking::catch_unwind::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x60be43144705 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x60be43144705 - test[fad20673b750c10e]::run_test_in_process
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x60be43144705 - test[fad20673b750c10e]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x60be4313f264 - test[fad20673b750c10e]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x60be4313f264 - std[d054f6f42beb1baa]::sys::backtrace::__rust_begin_short_backtrace::<test[fad20673b750c10e]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x60be43147782 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x60be43147782 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x60be43147782 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x60be43147782 - std[d054f6f42beb1baa]::panicking::catch_unwind::<(), core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x60be43147782 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x60be43147782 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x60be43147782 - <std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x60be4342337f - <alloc[154f689e5dd3bae5]::boxed::Box<dyn core[5a1c3e9896636f3a]::ops::function::FnOnce<(), Output = ()> + core[5a1c3e9896636f3a]::marker::Send> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   54:     0x60be4342337f - <std[d054f6f42beb1baa]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x7821718d4aa4 - <unknown>
[INFO] [stdout]   56:     0x782171961a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test::test_module::case_05_class
[INFO] [stdout]     test::test_module::case_08_constructor
[INFO] [stdout]     test::test_module::case_14_inheritance
[INFO] [stdout]     test::test_module::case_24_super
[INFO] [stdout]     test::test_module::case_25_this
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 26 passed; 5 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.29s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "e88d89930c04968f0514a6cc6f130e1d1c95941d86752bd6d323b4e3650ab043", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e88d89930c04968f0514a6cc6f130e1d1c95941d86752bd6d323b4e3650ab043", kill_on_drop: false }`
[INFO] [stdout] e88d89930c04968f0514a6cc6f130e1d1c95941d86752bd6d323b4e3650ab043
